04-24 14:24:17 D/ModuleListener: ModuleListener.testStarted(com.google.android.cuj.tvts.TvtsPerfCujRunner#testPerfCuj) on SKBFXYZO0C1V
04-24 14:24:17 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-24 14:24:17 D/DeviceHostUtils: apiLevel: 31
04-24 14:24:17 D/RunUtil: Running command null without timeout.
04-24 14:24:17 D/AppRunnerUtils: Checking service: com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
04-24 14:24:17 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
04-24 14:24:17 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, activity, service, com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService] with timeout: 2m 0s
04-24 14:24:17 I/AppRunnerUtils: Service PID = 14877
04-24 14:24:17 D/RunUtil: Running command null without timeout.
04-24 14:24:17 D/AppRunnerUtils: Checking service: com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
04-24 14:24:17 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
04-24 14:24:17 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, activity, service, com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService] with timeout: 2m 0s
04-24 14:24:17 I/AppRunnerUtils: Service PID = 14877
04-24 14:24:17 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
04-24 14:24:17 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
04-24 14:24:18 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-24 14:24:18 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
04-24 14:24:18 I/DeviceStateMonitor: Waiting 359927 ms for device SKBFXYZO0C1V package manager
04-24 14:24:18 I/NativeDeviceStateMonitor: Waiting 359785 ms for device SKBFXYZO0C1V external store
04-24 14:24:21 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
04-24 14:24:21 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
04-24 14:24:21 D/AssistantTestUtils: Assistant successfully launched: true
04-24 14:24:22 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.katniss
04-24 14:24:22 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, force-stop, com.google.android.katniss] with timeout: 2m 0s
04-24 14:24:22 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-24 14:24:22 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-24 14:24:23 D/RunUtil: Running command null without timeout.
04-24 14:24:23 D/AppRunnerUtils: Checking service: com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
04-24 14:24:23 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
04-24 14:24:23 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, activity, service, com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService] with timeout: 2m 0s
04-24 14:24:23 I/AppRunnerUtils: Service PID = 15505
04-24 14:24:23 D/RunUtil: Running command null without timeout.
04-24 14:24:23 D/AppRunnerUtils: Checking service: com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
04-24 14:24:23 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
04-24 14:24:23 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, activity, service, com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService] with timeout: 2m 0s
04-24 14:24:23 I/AppRunnerUtils: Service PID = 15505
04-24 14:24:23 I/AssistantTestUtils: Waiting for assistant to be ready...
04-24 14:24:23 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
04-24 14:24:23 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
04-24 14:24:24 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-24 14:24:24 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
04-24 14:24:24 I/DeviceStateMonitor: Waiting 359928 ms for device SKBFXYZO0C1V package manager
04-24 14:24:24 I/NativeDeviceStateMonitor: Waiting 359750 ms for device SKBFXYZO0C1V external store
04-24 14:24:26 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
04-24 14:24:27 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
04-24 14:24:27 D/AssistantTestUtils: Assistant successfully launched: true
04-24 14:24:28 D/RunUtil: Running command [logcat -d -T 1713936261.000 | grep -E 'AssistantWrapper(Impl)?: Assistant is now running.'] without timeout.
04-24 14:24:28 I/AssistantTestUtils: Assistant ready: true
04-24 14:24:28 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
04-24 14:24:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
04-24 14:24:28 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-24 14:24:28 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
04-24 14:24:28 I/DeviceStateMonitor: Waiting 359928 ms for device SKBFXYZO0C1V package manager
04-24 14:24:29 I/NativeDeviceStateMonitor: Waiting 359861 ms for device SKBFXYZO0C1V external store
04-24 14:24:31 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
04-24 14:24:31 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
04-24 14:24:31 D/AssistantTestUtils: Assistant successfully launched: true
04-24 14:24:32 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 14:24:32 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
04-24 14:24:33 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
04-24 14:24:33 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
04-24 14:24:33 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
04-24 14:24:34 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-24 14:24:34 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
04-24 14:24:34 I/DeviceStateMonitor: Waiting 359679 ms for device SKBFXYZO0C1V package manager
04-24 14:24:34 I/NativeDeviceStateMonitor: Waiting 359490 ms for device SKBFXYZO0C1V external store
04-24 14:24:37 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, window, windows, --proto] with timeout: 2m 0s
04-24 14:24:37 D/AppRunnerUtils: com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity
04-24 14:24:37 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
04-24 14:24:37 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
04-24 14:24:37 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-24 14:24:37 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
04-24 14:24:37 I/DeviceStateMonitor: Waiting 359934 ms for device SKBFXYZO0C1V package manager
04-24 14:24:37 I/NativeDeviceStateMonitor: Waiting 359872 ms for device SKBFXYZO0C1V external store
04-24 14:24:39 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, window, windows, --proto] with timeout: 2m 0s
04-24 14:24:39 D/AppRunnerUtils: com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity
04-24 14:24:39 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
04-24 14:24:39 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
04-24 14:24:40 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-24 14:24:40 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0C1V boot complete
04-24 14:24:40 I/DeviceStateMonitor: Waiting 359933 ms for device SKBFXYZO0C1V package manager
04-24 14:24:40 I/NativeDeviceStateMonitor: Waiting 359866 ms for device SKBFXYZO0C1V external store
04-24 14:24:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, window, windows, --proto] with timeout: 2m 0s
04-24 14:24:42 D/AppRunnerUtils: com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity
04-24 14:24:42 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
04-24 14:24:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
04-24 14:24:43 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-24 14:24:43 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
04-24 14:24:43 I/DeviceStateMonitor: Waiting 359933 ms for device SKBFXYZO0C1V package manager
04-24 14:24:43 I/NativeDeviceStateMonitor: Waiting 359867 ms for device SKBFXYZO0C1V external store
04-24 14:24:45 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, window, windows, --proto] with timeout: 2m 0s
04-24 14:24:45 D/AppRunnerUtils: com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity
04-24 14:24:45 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
04-24 14:24:45 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
04-24 14:24:46 I/NativeDeviceStateMonitor: Device SKBFXYZO0C1V is already ONLINE
04-24 14:24:46 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0C1V boot complete
04-24 14:24:46 I/DeviceStateMonitor: Waiting 359939 ms for device SKBFXYZO0C1V package manager
04-24 14:24:46 I/NativeDeviceStateMonitor: Waiting 359872 ms for device SKBFXYZO0C1V external store
04-24 14:24:48 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, dumpsys, window, windows, --proto] with timeout: 2m 0s
04-24 14:24:48 D/AppRunnerUtils: com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity
04-24 14:24:48 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
04-24 14:24:48 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
04-24 14:24:48 D/AssistantTestUtils: Assistant setup not triggered after 5 retries. Current activity: com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity
04-24 14:24:48 I/AssistantTestUtils: Assistant OOBE skipping required: false
04-24 14:24:48 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 14:24:49 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
04-24 14:24:50 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
04-24 14:24:50 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-24 14:24:50 D/DeviceHostUtils: apiLevel: 31
04-24 14:24:50 D/RunUtil: Running command null without timeout.
04-24 14:24:50 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
04-24 14:24:50 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
04-24 14:24:50 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
04-24 14:24:50 D/RunUtil: Running command null without timeout.
04-24 14:24:50 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
04-24 14:24:50 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
04-24 14:24:51 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
04-24 14:24:51 I/AppRunnerUtils: launcherPackageName: com.skb.tv
04-24 14:24:51 I/TvtsPerfCujRunner: Launcher: com.skb.tv
04-24 14:24:51 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-24 14:24:51 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0C1V
04-24 14:24:51 I/TvtsPerfCujRunner: Device is GTV: false
04-24 14:24:51 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-24 14:24:51 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0C1V
04-24 14:24:51 I/TvtsPerfCujRunner: Settings filename: sh-settings-custom-launcher.json
04-24 14:24:51 D/PerfCujSettings: createFromResourceFile: [sh-settings-custom-launcher.json]
04-24 14:24:51 I/TvtsPerfCujRunner: [34mUsing default settings[0m
04-24 14:24:51 I/TvtsPerfCujRunner: Perf CUJ Settings
Test Enabled:                            false
Test Enabled on ARM64:                   false
TVTS Build ID:                           Unknown
System Health Toolkit version:           Unknown
System Health Toolkit filename:          [default]
# runs per CUJ:                          3
Max retry time per CUJ (mins):           20
Max retries per CUJ:                     10
Enforce Uptime:                          false
Using Custom Settings:                   false
Uptime:                                  10 Minutes
Test filter:                             null
Exclude Settings:                        []
Include Benchmarks:                      
Long Duration Tests:                     []
Required Packages:                       
Thresholds:                              

04-24 14:24:51 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-24 14:24:51 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0C1V
04-24 14:24:51 I/TvtsPerfCujRunner: This test currently not enabled on custom launcher devices
04-24 14:24:51 I/TvtsPerfCujRunner: Running performance CUJ tests...

04-24 14:24:51 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, pm, list, features] with timeout: 2m 0s
04-24 14:24:51 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0C1V
04-24 14:24:51 I/TvtsPerfCujRunner: This test currently not enabled on custom launcher devices
04-24 14:24:51 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.katniss
04-24 14:24:51 D/RunUtil: Running command [adb, -s, SKBFXYZO0C1V, shell, am, force-stop, com.google.android.katniss] with timeout: 2m 0s
04-24 14:24:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-24 14:24:52 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-24 14:24:52 D/PrettyTestEventLogger: 
==================== com.google.android.cuj.tvts.TvtsPerfCujRunner#testPerfCuj ENDED: Wed Apr 24 14:24:52 KST 2024 ====================
